[07/01 11:31:39,Telerik.TestStudio.ExecutionManagerService.exe(3992:4),Execution] JobBroker.ScheduleJobAsync() : Job satisfied all preconditions, id = f121abc5-a1e3-47eb-a151-1e4ab939adcc
[07/01 11:31:39,Telerik.TestStudio.ExecutionManagerService.exe(3992:4),Execution] JobBroker.ScheduleJobAsync() : Job sent to scheduler, id = f121abc5-a1e3-47eb-a151-1e4ab939adcc
[07/01 11:31:39,Telerik.TestStudio.ExecutionManagerService.exe(3992:4),Execution] JobsController.CreateNewJob() : Accepted new job, Id = f121abc5-a1e3-47eb-a151-1e4ab939adcc
[07/01 11:31:48,Telerik.TestStudio.ExecutionManagerService.exe(3992:7),Execution] JobRunner.RunJobAsync() : TestList loaded for job ID = f121abc5-a1e3-47eb-a151-1e4ab939adcc
[07/01 11:31:48,Telerik.TestStudio.ExecutionManagerService.exe(3992:7),Execution] JobRunner.RunJobAsync() : Job started, ID = f121abc5-a1e3-47eb-a151-1e4ab939adcc
[07/01 11:31:48,Telerik.TestStudio.RemoteExecutor.exe(3748:16)] First trace message from pool unnamed thread (managed ID = 16, native ID = 4636).
[07/01 11:31:48,Telerik.TestStudio.RemoteExecutor.exe(3748:16),Execution] RunningTestsController.StartTest() : Test run requested for Cloud path=I:\Application Development Team\TestStudio\briar-tests\SJ Regression
[07/01 11:32:58,ArtOfTest.Runner.exe(3712:3)] First trace message from process 3712: "C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Runner.exe"  /controller /parentprocesshandle=3084
[07/01 11:32:58,ArtOfTest.Runner.exe(3712:3)] First trace message from foreground thread "Remote.ArtOfTest.Runner.Listener" (managed ID = 3, native ID = 4548).
[07/01 11:32:58,ArtOfTest.Runner.exe(3712:3),TestStudio] Loader.LoadTranslatorGroups() : ------- Function Start -------
[07/01 11:32:59,ArtOfTest.Runner.exe(3712:3),TestStudio] Loader.LoadTranslatorGroups() : ------- Function End -------
[07/01 11:32:59,ArtOfTest.Runner.exe(3712:4)] First trace message from pool unnamed thread (managed ID = 4, native ID = 2772).
[07/01 11:32:59,ArtOfTest.Runner.exe(3712:4),DialogPlayback] DialogMonitor.Start() : Beginning monitoring for dialogs from the set {}.
[07/01 11:32:59,ArtOfTest.Runner.exe(3712:4),Error] TestExecuteProxy.ExecuteTest() : EXCEPTION! (see below)
     Outer Exception Type: System.ApplicationException
     Message: Exception thrown attempting to launch Silverlight application. Please make sure Silverlight is properly installed and you are able to launch it.
     HRESULT: 0x80131600 (Official ID (if app.) = COR_E_APPLICATION, Error Bit = FAILED, Facility = FACILITY_URT, Code = 5632)
     Call Stack:
          at ArtOfTest.WebAii.BrowserSpecialized.SilverlightOutOfBrowser.SilverlightOutOfBrowserActions.LaunchNewBrowserInstance(Int32 timeout, ProcessWindowStyle windowStyle, String arguments, String pipename)
          at ArtOfTest.WebAii.Core.Manager.LaunchNewBrowser(BrowserType browserToLaunch, Boolean waitForBrowserToConnect, ProcessWindowStyle windowStyle, String arguments)
          at ArtOfTest.WebAii.Design.Execution.ExecutionEngine.InitializeWeb(ExecutionEngineCreateParams initParams)
          at ArtOfTest.WebAii.Design.Execution.TestExecuteProxy.CreateAndInitializeEngine(Test test, ExecutionEngineCreateParams cp)
          at ArtOfTest.WebAii.Design.Execution.TestExecuteProxy.ExecuteTest(ExecuteTestCommand command)

     Inner Exception Type: System.ArgumentException
     Message: Invalid path to Out-Of-Browser application:
     HRESULT: 0x80070057 (Official ID (if app.) = E_INVALIDARG, Error Bit = FAILED, Facility = FACILITY_WIN32, Code = ERROR_INVALID_PARAMETER)
     Call Stack:
          at ArtOfTest.WebAii.BrowserSpecialized.SilverlightOutOfBrowser.SilverlightOutOfBrowserActions.LaunchNewBrowserInstance(Int32 timeout, ProcessWindowStyle windowStyle, String arguments, String pipename)

[07/01 11:32:59,ArtOfTest.Runner.exe(3712:4),Framework] BrowserProvisioner.EndService() : Connecting to the provisioning pipe "Pipe.ArtOfTest.WebAii.BrowserProvisioner_-588657817" in order to unblock and eventually terminate it...
[07/01 11:32:59,ArtOfTest.Runner.exe(3712:6)] First trace message from background thread "UAI Dispatcher Thread" (managed ID = 6, native ID = 4588).
[07/01 11:32:59,ArtOfTest.Runner.exe(3712:6),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:32:59,ArtOfTest.Runner.exe(3712:5)] First trace message from background thread "Pipe.ArtOfTest.WebAii.BrowserProvisioner" (managed ID = 5, native ID = 3688).
[07/01 11:32:59,ArtOfTest.Runner.exe(3712:5),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe but this will be treated as a termination cue.
[07/01 11:33:00,ArtOfTest.Runner.exe(3712:4),Framework] BrowserProvisioner.EndService() : Provisioning pipe connected.
[07/01 11:33:00,ArtOfTest.Runner.exe(3712:4),Framework] BrowserProvisioner.EndService() : Provisioning thread terminated.
[07/01 11:33:00,ArtOfTest.Runner.exe(3712:4),DialogPlayback] DialogMonitor.Stop() : Dialog monitoring is being halted.
[07/01 11:33:00,ArtOfTest.Runner.exe(3712:4),UIAutomation] AutomationExtensions.AddAutomationEventHandler() : Added a(n) WindowPatternIdentifiers.WindowOpenedProperty handler on element System.Windows.Automation.AutomationElement scope Descendants.
[07/01 11:33:00,ArtOfTest.Runner.exe(3712:4),DialogPlayback] DialogMonitor.Start() : Beginning monitoring for dialogs from the set {}.
[07/01 11:33:00,ArtOfTest.Runner.exe(3712:10)] First trace message from background thread "UAI Dispatcher Thread" (managed ID = 10, native ID = 1596).
[07/01 11:33:00,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:00,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:00,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:4),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Process launched (ID=4848, Path="C:\Program Files\Internet Explorer\iexplore.exe", Arguments="-nomerge about:blank").
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:4),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Attempting to attach on IE frame (HWND=852338)...
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:4),Framework] Connector.Start() : Process launched (ID=3528, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 197204").
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:4),Framework] Connector.Start() : Process exited (ID=3528, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 197204").
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:4),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Process "Telerik.TestStudio.RemoteExecutor" (ID=3748) is assumed to host the controller.
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:4),Framework] Connector.InjectCode() : ------- Function Start -------
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:4),Framework] Connector.InjectCode() : Connection string: C:\Windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2016.2.630.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?3748
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:4),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 197204 on another thread...
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:15)] First trace message from pool thread "WaitAsync polling wait worker" (managed ID = 15, native ID = 4384).
[07/01 11:33:01,ArtOfTest.Runner.exe(3712:15),Framework] <>c__DisplayClass25_3.<WaitForDocument>b__1() : Attempting to get document from window handle
[07/01 11:33:02,ArtOfTest.Runner.exe(3712:4),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[07/01 11:33:02,ArtOfTest.Runner.exe(3712:4),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 197204 (lpData = "C:\Windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2016.2.630.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?3748")...
[07/01 11:33:02,IEXPLORE.EXE(3184:1)] First trace message from process 3184: "C:\Program Files (x86)\Internet Explorer\IEXPLORE.EXE" SCODEF:4848 CREDAT:209921 /prefetch:2
[07/01 11:33:02,IEXPLORE.EXE(3184:1)] First trace message from background unnamed thread (managed ID = 1, native ID = 3816).
[07/01 11:33:02,IEXPLORE.EXE(3184:1),Framework] ArtOfTestPluginEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (HWND = 197204, PipeName = "Pipe.ArtOfTest.WebAii.BrowserProvisioner_1432816914", PID = 3748).
[07/01 11:33:02,IEXPLORE.EXE(3184:1),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 197204 on same thread...
[07/01 11:33:02,IEXPLORE.EXE(3184:1),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[07/01 11:33:02,IEXPLORE.EXE(3184:1),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_0dec4224-de00-47f2-a9e6-8bdac2a2bc57".
[07/01 11:33:02,ArtOfTest.Runner.exe(3712:9)] First trace message from background thread "Pipe.ArtOfTest.WebAii.BrowserProvisioner" (managed ID = 9, native ID = 4624).
[07/01 11:33:02,ArtOfTest.Runner.exe(3712:9),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[07/01 11:33:02,ArtOfTest.Runner.exe(3712:13)] First trace message from background thread "Remoted async command listener" (managed ID = 13, native ID = 4036).
[07/01 11:33:02,ArtOfTest.Runner.exe(3712:13),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[07/01 11:33:02,IEXPLORE.EXE(3184:1),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "8a60393a-ebf0-4fdf-bf1a-7e77b1d588f8.CommandPipe", Async = "8a60393a-ebf0-4fdf-bf1a-7e77b1d588f8.AsyncPipe"}
[07/01 11:33:02,ArtOfTest.Runner.exe(3712:13),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[07/01 11:33:02,ArtOfTest.Runner.exe(3712:13),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[07/01 11:33:02,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:02,ArtOfTest.Runner.exe(3712:4),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=ERROR_ALREADY_EXISTS).
[07/01 11:33:02,ArtOfTest.Runner.exe(3712:4),Framework] Connector.InjectCode() : ------- Function End -------
[07/01 11:33:02,ArtOfTest.Runner.exe(3712:9),Framework] Manager.AddNewBrowser() : Added client ID "Client_0dec4224-de00-47f2-a9e6-8bdac2a2bc57" (now there is/are 1 total).
[07/01 11:33:03,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'Simple Login Test', path: 'Simple Login Test.tstest.'
[07/01 11:33:03,ArtOfTest.Runner.exe(3712:4),Execution] ExtensionsManager.LoadExtensions() : ------- Function Start -------
[07/01 11:33:03,ArtOfTest.Runner.exe(3712:4),Uncategorized] ExtensionsManager.LoadExtensions() : Got 15 plugin(s) in folder "C:\Program Files (x86)\Telerik\Test Studio\Bin\Plugins".
[07/01 11:33:03,ArtOfTest.Runner.exe(3712:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "LibGit2Sharp.dll" from plugins folder.
[07/01 11:33:03,ArtOfTest.Runner.exe(3712:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Functional.dll" from plugins folder.
[07/01 11:33:03,ArtOfTest.Runner.exe(3712:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Jira.BugTracking.dll" from plugins folder.
[07/01 11:33:03,ArtOfTest.Runner.exe(3712:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Load.dll" from plugins folder.
[07/01 11:33:03,ArtOfTest.Runner.exe(3712:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Manual.dll" from plugins folder.
[07/01 11:33:03,ArtOfTest.Runner.exe(3712:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.OTA.Interop.dll" from plugins folder.
[07/01 11:33:03,ArtOfTest.Runner.exe(3712:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Plugins.dll" from plugins folder.
[07/01 11:33:03,ArtOfTest.Runner.exe(3712:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.ProductGuide.dll" from plugins folder.
[07/01 11:33:03,ArtOfTest.Runner.exe(3712:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Shared.dll" from plugins folder.
[07/01 11:33:03,ArtOfTest.Runner.exe(3712:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.SourceControl.dll" from plugins folder.
[07/01 11:33:03,ArtOfTest.Runner.exe(3712:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.SourceControl.Git.dll" from plugins folder.
[07/01 11:33:03,ArtOfTest.Runner.exe(3712:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.SourceControl.Git.TS.dll" from plugins folder.
[07/01 11:33:03,ArtOfTest.Runner.exe(3712:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.TeamPulse.dll" from plugins folder.
[07/01 11:33:03,ArtOfTest.Runner.exe(3712:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Web.dll" from plugins folder.
[07/01 11:33:03,ArtOfTest.Runner.exe(3712:4),Uncategorized] ExtensionsManager.LoadExtensions() : Loaded assembly "Telerik.TestStudio.Wpf.dll" from plugins folder.
[07/01 11:33:03,ArtOfTest.Runner.exe(3712:4),Execution] ExtensionsManager.LoadExtensions() : ------- Function End -------
[07/01 11:33:03,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[07/01 11:33:03,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:33:03,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Navigate to : '/ereg'"...
[07/01 11:33:04,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Navigate to : '/ereg'".
[07/01 11:33:04,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:33:04,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "Execute test 'sub SJ Login.tstest'"...
[07/01 11:33:04,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'sub SJ Login', path: 'TestsAsStep\sub SJ Login.tstest.'
[07/01 11:33:04,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[07/01 11:33:04,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:33:04,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Wait for '6000' msec."...
[07/01 11:33:10,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Wait for '6000' msec.".
[07/01 11:33:10,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:33:10,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "Verify 'MyFormJIdWImage' is enabled"...
[07/01 11:33:10,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "Verify 'MyFormJIdWImage' is enabled".
[07/01 11:33:10,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:33:10,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "Enter text 'bparmer' in 'MyFormUserIdText'"...
[07/01 11:33:12,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "Enter text 'bparmer' in 'MyFormUserIdText'".
[07/01 11:33:12,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:33:12,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 4 "Enter text 'bparmer' in 'MyFormPsswdPassword'"...
[07/01 11:33:14,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 4 "Enter text 'bparmer' in 'MyFormPsswdPassword'".
[07/01 11:33:14,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:33:14,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 5 "Click 'MyFormJIdWImage'"...
[07/01 11:33:14,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 5 "Click 'MyFormJIdWImage'".
[07/01 11:33:14,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:33:17,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 6 "Verify 'NewAppLink' is enabled"...
[07/01 11:33:17,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 6 "Verify 'NewAppLink' is enabled".
[07/01 11:33:17,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "Execute test 'sub SJ Login.tstest'".
[07/01 11:33:17,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::Start
[07/01 11:33:18,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::End
[07/01 11:33:18,ArtOfTest.Runner.exe(3712:4),Framework] BrowserProvisioner.EndService() : Connecting to the provisioning pipe "Pipe.ArtOfTest.WebAii.BrowserProvisioner_1432816914" in order to unblock and eventually terminate it...
[07/01 11:33:18,ArtOfTest.Runner.exe(3712:4),Framework] BrowserProvisioner.EndService() : Provisioning pipe connected.
[07/01 11:33:18,ArtOfTest.Runner.exe(3712:9),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe but this will be treated as a termination cue.
[07/01 11:33:18,ArtOfTest.Runner.exe(3712:4),Framework] BrowserProvisioner.EndService() : Provisioning thread terminated.
[07/01 11:33:18,ArtOfTest.Runner.exe(3712:4),DialogPlayback] DialogMonitor.AddDialog() : Added {OnBeforeUnloadDialog(Dismiss=OK)} for monitoring.
[07/01 11:33:18,ArtOfTest.Runner.exe(3712:4),Framework] Manager.RemoveBrowser() : Removed client ID "Client_0dec4224-de00-47f2-a9e6-8bdac2a2bc57" (0 remaining).
[07/01 11:33:18,ArtOfTest.Runner.exe(3712:4),Framework] Manager.SetActiveBrowser() : Active browser is now null.
[07/01 11:33:18,ArtOfTest.Runner.exe(3712:4),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 13).
[07/01 11:33:18,IEXPLORE.EXE(3184:1),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_0dec4224-de00-47f2-a9e6-8bdac2a2bc57") to pipe server...
[07/01 11:33:18,IEXPLORE.EXE(3184:1),Framework] BrowserRemoteClient.Disconnect() : Command pipe is already disconnected.
[07/01 11:33:18,ArtOfTest.Runner.exe(3712:13),Warning] PipeCommunication.SyncPipeRead() : Pipe read broken by thread-abort.
[07/01 11:33:18,ArtOfTest.Runner.exe(3712:10),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:21,ArtOfTest.Runner.exe(3712:4),DialogPlayback] DialogMonitor.Stop() : Dialog monitoring is being halted.
[07/01 11:33:21,ArtOfTest.Runner.exe(3712:4),UIAutomation] AutomationExtensions.AddAutomationEventHandler() : Added a(n) WindowPatternIdentifiers.WindowOpenedProperty handler on element System.Windows.Automation.AutomationElement scope Descendants.
[07/01 11:33:22,Telerik.TestStudio.RemoteExecutor.exe(3748:36)] First trace message from background thread "Callback Listener Thread" (managed ID = 36, native ID = 2224).
[07/01 11:33:22,Telerik.TestStudio.RemoteExecutor.exe(3748:36),Execution] TestPlatform.AfterRunFinished() : Finished running test platform- attempting publish
[07/01 11:33:23,Telerik.TestStudio.RemoteExecutor.exe(3748:20)] First trace message from pool unnamed thread (managed ID = 20, native ID = 3504).
[07/01 11:33:23,Telerik.TestStudio.RemoteExecutor.exe(3748:20),Execution] RunningTestsController.StartTest() : Test run requested for Cloud path=I:\Application Development Team\TestStudio\briar-tests\SJ Regression
[07/01 11:33:25,ArtOfTest.Runner.exe(3712:15)] First trace message from pool unnamed thread (managed ID = 15, native ID = 4384).
[07/01 11:33:25,ArtOfTest.Runner.exe(3712:15),DialogPlayback] DialogMonitor.Start() : Beginning monitoring for dialogs from the set {}.
[07/01 11:33:25,ArtOfTest.Runner.exe(3712:15),Error] TestExecuteProxy.ExecuteTest() : EXCEPTION! (see below)
     Outer Exception Type: System.ApplicationException
     Message: Exception thrown attempting to launch Silverlight application. Please make sure Silverlight is properly installed and you are able to launch it.
     HRESULT: 0x80131600 (Official ID (if app.) = COR_E_APPLICATION, Error Bit = FAILED, Facility = FACILITY_URT, Code = 5632)
     Call Stack:
          at ArtOfTest.WebAii.BrowserSpecialized.SilverlightOutOfBrowser.SilverlightOutOfBrowserActions.LaunchNewBrowserInstance(Int32 timeout, ProcessWindowStyle windowStyle, String arguments, String pipename)
          at ArtOfTest.WebAii.Core.Manager.LaunchNewBrowser(BrowserType browserToLaunch, Boolean waitForBrowserToConnect, ProcessWindowStyle windowStyle, String arguments)
          at ArtOfTest.WebAii.Design.Execution.ExecutionEngine.InitializeWeb(ExecutionEngineCreateParams initParams)
          at ArtOfTest.WebAii.Design.Execution.TestExecuteProxy.CreateAndInitializeEngine(Test test, ExecutionEngineCreateParams cp)
          at ArtOfTest.WebAii.Design.Execution.TestExecuteProxy.ExecuteTest(ExecuteTestCommand command)

     Inner Exception Type: System.ArgumentException
     Message: Invalid path to Out-Of-Browser application:
     HRESULT: 0x80070057 (Official ID (if app.) = E_INVALIDARG, Error Bit = FAILED, Facility = FACILITY_WIN32, Code = ERROR_INVALID_PARAMETER)
     Call Stack:
          at ArtOfTest.WebAii.BrowserSpecialized.SilverlightOutOfBrowser.SilverlightOutOfBrowserActions.LaunchNewBrowserInstance(Int32 timeout, ProcessWindowStyle windowStyle, String arguments, String pipename)

[07/01 11:33:25,ArtOfTest.Runner.exe(3712:15),Framework] BrowserProvisioner.EndService() : Connecting to the provisioning pipe "Pipe.ArtOfTest.WebAii.BrowserProvisioner_-1433649015" in order to unblock and eventually terminate it...
[07/01 11:33:25,ArtOfTest.Runner.exe(3712:12)] First trace message from background thread "UAI Dispatcher Thread" (managed ID = 12, native ID = 3976).
[07/01 11:33:25,ArtOfTest.Runner.exe(3712:12),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:25,ArtOfTest.Runner.exe(3712:13)] First trace message from background thread "Remoted async command listener" (managed ID = 13, native ID = 4992).
[07/01 11:33:25,ArtOfTest.Runner.exe(3712:13),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe but this will be treated as a termination cue.
[07/01 11:33:25,ArtOfTest.Runner.exe(3712:15),Framework] BrowserProvisioner.EndService() : Provisioning pipe connected.
[07/01 11:33:25,ArtOfTest.Runner.exe(3712:15),Framework] BrowserProvisioner.EndService() : Provisioning thread terminated.
[07/01 11:33:25,ArtOfTest.Runner.exe(3712:15),DialogPlayback] DialogMonitor.Stop() : Dialog monitoring is being halted.
[07/01 11:33:25,ArtOfTest.Runner.exe(3712:15),UIAutomation] AutomationExtensions.AddAutomationEventHandler() : Added a(n) WindowPatternIdentifiers.WindowOpenedProperty handler on element System.Windows.Automation.AutomationElement scope Descendants.
[07/01 11:33:26,ArtOfTest.Runner.exe(3712:4),DialogPlayback] DialogMonitor.Start() : Beginning monitoring for dialogs from the set {}.
[07/01 11:33:26,ArtOfTest.Runner.exe(3712:9)] First trace message from background thread "UAI Dispatcher Thread" (managed ID = 9, native ID = 4800).
[07/01 11:33:26,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:26,ArtOfTest.Runner.exe(3712:4),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Process launched (ID=4592, Path="C:\Program Files\Internet Explorer\iexplore.exe", Arguments="-nomerge about:blank").
[07/01 11:33:26,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:26,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:26,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:4),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Attempting to attach on IE frame (HWND=459346)...
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:4),Framework] Connector.Start() : Process launched (ID=2296, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 918254").
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:4),Framework] Connector.Start() : Process exited (ID=2296, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 918254").
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:4),Framework] InternetExplorerActions.LaunchNewBrowserInstance() : Process "Telerik.TestStudio.RemoteExecutor" (ID=3748) is assumed to host the controller.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:4),Framework] Connector.InjectCode() : ------- Function Start -------
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:4),Framework] Connector.InjectCode() : Connection string: C:\Windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2016.2.630.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?3748
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:4),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 918254 on another thread...
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:15)] First trace message from pool thread "WaitAsync polling wait worker" (managed ID = 15, native ID = 4384).
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:15),Framework] <>c__DisplayClass25_3.<WaitForDocument>b__1() : Attempting to get document from window handle
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:4),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[07/01 11:33:27,ArtOfTest.Runner.exe(3712:4),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 918254 (lpData = "C:\Windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2016.2.630.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?3748")...
[07/01 11:33:27,IEXPLORE.EXE(4140:1)] First trace message from process 4140: "C:\Program Files (x86)\Internet Explorer\IEXPLORE.EXE" SCODEF:4592 CREDAT:209921 /prefetch:2
[07/01 11:33:27,IEXPLORE.EXE(4140:1)] First trace message from background unnamed thread (managed ID = 1, native ID = 144).
[07/01 11:33:27,IEXPLORE.EXE(4140:1),Framework] ArtOfTestPluginEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (HWND = 918254, PipeName = "Pipe.ArtOfTest.WebAii.BrowserProvisioner_1042259122", PID = 3748).
[07/01 11:33:27,IEXPLORE.EXE(4140:1),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 918254 on same thread...
[07/01 11:33:28,IEXPLORE.EXE(4140:1),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[07/01 11:33:28,IEXPLORE.EXE(4140:1),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_c36b383a-d582-46d1-b0be-8a7bdc4c17c7".
[07/01 11:33:28,ArtOfTest.Runner.exe(3712:10)] First trace message from background thread "Pipe.ArtOfTest.WebAii.BrowserProvisioner" (managed ID = 10, native ID = 3228).
[07/01 11:33:28,ArtOfTest.Runner.exe(3712:10),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[07/01 11:33:28,ArtOfTest.Runner.exe(3712:21)] First trace message from background thread "Remoted async command listener" (managed ID = 21, native ID = 3644).
[07/01 11:33:28,ArtOfTest.Runner.exe(3712:21),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[07/01 11:33:28,IEXPLORE.EXE(4140:1),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "8614a0d9-6d70-496c-80fb-902599780944.CommandPipe", Async = "8614a0d9-6d70-496c-80fb-902599780944.AsyncPipe"}
[07/01 11:33:28,ArtOfTest.Runner.exe(3712:21),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[07/01 11:33:28,ArtOfTest.Runner.exe(3712:21),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[07/01 11:33:28,ArtOfTest.Runner.exe(3712:9),Framework] Manager.get_ActiveBrowser() : The current ActiveBrowser is either invisible or disconnected.
[07/01 11:33:28,ArtOfTest.Runner.exe(3712:4),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=ERROR_ALREADY_EXISTS).
[07/01 11:33:28,ArtOfTest.Runner.exe(3712:4),Framework] Connector.InjectCode() : ------- Function End -------
[07/01 11:33:28,ArtOfTest.Runner.exe(3712:10),Framework] Manager.AddNewBrowser() : Added client ID "Client_c36b383a-d582-46d1-b0be-8a7bdc4c17c7" (now there is/are 1 total).
[07/01 11:33:28,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'SJ Allocations Page', path: 'SJ Allocations Page.tstest.'
[07/01 11:33:28,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[07/01 11:33:28,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:33:28,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Navigate to : '/ereg'"...
[07/01 11:33:29,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Navigate to : '/ereg'".
[07/01 11:33:29,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:33:29,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "Execute test 'sub SJ Login'"...
[07/01 11:33:29,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'sub SJ Login', path: 'TestsAsStep\sub SJ Login.tstest.'
[07/01 11:33:29,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[07/01 11:33:29,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:33:29,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Wait for '6000' msec."...
[07/01 11:33:35,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Wait for '6000' msec.".
[07/01 11:33:35,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:33:35,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "Verify 'MyFormJIdWImage' is enabled"...
[07/01 11:33:35,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "Verify 'MyFormJIdWImage' is enabled".
[07/01 11:33:35,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:33:35,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "Enter text 'bparmer' in 'MyFormUserIdText'"...
[07/01 11:33:37,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "Enter text 'bparmer' in 'MyFormUserIdText'".
[07/01 11:33:37,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:33:38,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 4 "Enter text 'bparmer' in 'MyFormPsswdPassword'"...
[07/01 11:33:39,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 4 "Enter text 'bparmer' in 'MyFormPsswdPassword'".
[07/01 11:33:39,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:33:39,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 5 "Click 'MyFormJIdWImage'"...
[07/01 11:33:39,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 5 "Click 'MyFormJIdWImage'".
[07/01 11:33:39,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:33:41,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 6 "Verify 'NewAppLink' is enabled"...
[07/01 11:33:41,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 6 "Verify 'NewAppLink' is enabled".
[07/01 11:33:41,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "Execute test 'sub SJ Login'".
[07/01 11:33:41,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:33:41,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "Execute test 'sub SJ CreateCUP'"...
[07/01 11:33:41,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.InternalExecuteTest() : Executing test: 'sub SJ CreateCUP', path: 'TestsAsStep\sub SJ CreateCUP.tstest.'
[07/01 11:33:41,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentResult() : VisualDebugger::SetCurrentResult
[07/01 11:33:42,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:33:42,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 1 "Comment: CREATE NEW APPLICATION"...
[07/01 11:33:42,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 1 "Comment: CREATE NEW APPLICATION".
[07/01 11:33:42,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:33:42,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 2 "Verify 'NewAppLink' is enabled"...
[07/01 11:33:42,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 2 "Verify 'NewAppLink' is enabled".
[07/01 11:33:42,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:33:42,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 3 "Click 'NewAppLink'"...
[07/01 11:33:42,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "Click 'NewAppLink'".
[07/01 11:33:42,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:33:42,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 4 "Connect to pop-up window : 'ereg/faces/jsp/capture/newApplication.jsf'"...
[07/01 11:33:42,IEXPLORE.EXE(4140:1),Framework] InternetExplorerActions.BeginAsyncInjection() : Spawned Internet Explorer injection thread (HWND=983462, AssemblyAndClass="C:\Windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2016.2.630.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint", PipeName="Pipe.ArtOfTest.WebAii.BrowserProvisioner_1042259122", ProcessId="3748").
[07/01 11:33:44,IEXPLORE.EXE(4140:7)] First trace message from pool thread "Internet Explorer injection thread" (managed ID = 7, native ID = 3208).
[07/01 11:33:44,IEXPLORE.EXE(4140:7),Error] ProcessExtensions.GetEntryModulePath() : EXCEPTION! (see below)
     Outer Exception Type: Telerik.TestingFramework.Interop.Win32.Win32Exception
     Message: ERROR_GEN_FAILURE (31) : A device attached to the system is not functioning
     HRESULT: 0x80004005 (Official ID (if app.) = E_FAIL, Error Bit = FAILED, Facility = FACILITY_NULL, Code = 16389)
     Call Stack:
          at Telerik.TestingFramework.Interop.Win32.KERNEL32.QueryFullProcessImageName(HANDLE hProcess, QueryFullProcessImageNameFlags dwFlags, Int32 iTryBufferLength)
          at ArtOfTest.Common.Extensions.ProcessExtensions.GetEntryModulePath(Process process)

[07/01 11:33:44,IEXPLORE.EXE(4140:7),Framework] Connector.Start() : Process launched (ID=1964, Path="", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 328390").
[07/01 11:33:44,IEXPLORE.EXE(4140:7),Error] ProcessExtensions.GetEntryModulePath() : EXCEPTION! (see below)
     Outer Exception Type: Telerik.TestingFramework.Interop.Win32.Win32Exception
     Message: ERROR_GEN_FAILURE (31) : A device attached to the system is not functioning
     HRESULT: 0x80004005 (Official ID (if app.) = E_FAIL, Error Bit = FAILED, Facility = FACILITY_NULL, Code = 16389)
     Call Stack:
          at Telerik.TestingFramework.Interop.Win32.KERNEL32.QueryFullProcessImageName(HANDLE hProcess, QueryFullProcessImageNameFlags dwFlags, Int32 iTryBufferLength)
          at ArtOfTest.Common.Extensions.ProcessExtensions.GetEntryModulePath(Process process)

[07/01 11:33:44,IEXPLORE.EXE(4140:7),Framework] Connector.Start() : Process exited (ID=1964, ExitCode=0, Path="", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 328390").
[07/01 11:33:44,IEXPLORE.EXE(4140:7),Framework] Connector.InjectCode() : ------- Function Start -------
[07/01 11:33:44,IEXPLORE.EXE(4140:7),Framework] Connector.InjectCode() : Connection string: C:\Windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2016.2.630.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?3748
[07/01 11:33:44,IEXPLORE.EXE(4140:7),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 328390 on another thread...
[07/01 11:33:44,IEXPLORE.EXE(4140:9)] First trace message from pool thread "WaitAsync polling wait worker" (managed ID = 9, native ID = 2492).
[07/01 11:33:44,IEXPLORE.EXE(4140:9),Framework] <>c__DisplayClass25_3.<WaitForDocument>b__1() : Attempting to get document from window handle
[07/01 11:33:44,IEXPLORE.EXE(4140:7),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[07/01 11:33:44,IEXPLORE.EXE(4140:7),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 328390 (lpData = "C:\Windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2016.2.630.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?3748")...
[07/01 11:33:44,IEXPLORE.EXE(4140:12)] First trace message from background unnamed thread (managed ID = 12, native ID = 4236).
[07/01 11:33:44,IEXPLORE.EXE(4140:12),Framework] ArtOfTestPluginEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (HWND = 328390, PipeName = "Pipe.ArtOfTest.WebAii.BrowserProvisioner_1042259122", PID = 3748).
[07/01 11:33:44,IEXPLORE.EXE(4140:12),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 328390 on same thread...
[07/01 11:33:44,IEXPLORE.EXE(4140:12),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[07/01 11:33:44,IEXPLORE.EXE(4140:12),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_b0284813-596c-4bfc-b613-5ef9c410d328".
[07/01 11:33:44,ArtOfTest.Runner.exe(3712:10),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[07/01 11:33:44,ArtOfTest.Runner.exe(3712:6)] First trace message from background thread "Remoted async command listener" (managed ID = 6, native ID = 2880).
[07/01 11:33:44,ArtOfTest.Runner.exe(3712:6),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[07/01 11:33:44,IEXPLORE.EXE(4140:12),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "3ae4f224-591b-4c69-aa70-5d5c1d10c728.CommandPipe", Async = "3ae4f224-591b-4c69-aa70-5d5c1d10c728.AsyncPipe"}
[07/01 11:33:44,ArtOfTest.Runner.exe(3712:6),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[07/01 11:33:44,ArtOfTest.Runner.exe(3712:6),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[07/01 11:33:44,IEXPLORE.EXE(4140:7),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=ERROR_ALREADY_EXISTS).
[07/01 11:33:44,IEXPLORE.EXE(4140:7),Framework] Connector.InjectCode() : ------- Function End -------
[07/01 11:33:44,ArtOfTest.Runner.exe(3712:10),Framework] Manager.AddNewBrowser() : Added client ID "Client_b0284813-596c-4bfc-b613-5ef9c410d328" (now there is/are 2 total).
[07/01 11:33:44,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 4 "Connect to pop-up window : 'ereg/faces/jsp/capture/newApplication.jsf'".
[07/01 11:33:44,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:33:44,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 5 "Check 'NewAppFormJIdRRadio' to be 'True'"...
[07/01 11:33:45,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 5 "Check 'NewAppFormJIdRRadio' to be 'True'".
[07/01 11:33:45,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:33:45,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 6 "Select 'ByValue' option 'HEAD' on 'NewAppFormSeqTypeSelect'"...
[07/01 11:33:46,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 6 "Select 'ByValue' option 'HEAD' on 'NewAppFormSeqTypeSelect'".
[07/01 11:33:46,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:33:46,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 7 "Select 'ByValue' option '1381' on 'NewAppFormRuleTypeSelect'"...
[07/01 11:33:47,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 7 "Select 'ByValue' option '1381' on 'NewAppFormRuleTypeSelect'".
[07/01 11:33:47,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:33:47,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 8 "Select 'ByValue' option '49' on 'NewAppFormCprmryCntySelect'"...
[07/01 11:33:47,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 8 "Select 'ByValue' option '49' on 'NewAppFormCprmryCntySelect'".
[07/01 11:33:47,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:33:47,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 9 "Enter text 'AUTOMATED TEST' in 'NewAppFormProjNmText'"...
[07/01 11:33:49,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 9 "Enter text 'AUTOMATED TEST' in 'NewAppFormProjNmText'".
[07/01 11:33:49,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:33:49,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 10 "Enter text 'Staff Comments' in 'NewAppFormCommentsTextArea'"...
[07/01 11:33:52,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 10 "Enter text 'Staff Comments' in 'NewAppFormCommentsTextArea'".
[07/01 11:33:52,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:33:52,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 11 "Verify 'NewAppFormSaveSubmit' is enabled"...
[07/01 11:33:52,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 11 "Verify 'NewAppFormSaveSubmit' is enabled".
[07/01 11:33:52,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:33:52,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 12 "Click 'NewAppFormSaveSubmit'"...
[07/01 11:33:52,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 12 "Click 'NewAppFormSaveSubmit'".
[07/01 11:33:52,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:33:53,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 13 "Click 'NewAppFormJId3tSubmit'"...
[07/01 11:33:53,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 13 "Click 'NewAppFormJId3tSubmit'".
[07/01 11:33:53,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:33:53,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 14 "Connect to pop-up window : 'ereg/faces/jsp/capture/application.jsf'"...
[07/01 11:33:57,IEXPLORE.EXE(4140:12),Framework] InternetExplorerActions.BeginAsyncInjection() : Spawned Internet Explorer injection thread (HWND=328278, AssemblyAndClass="C:\Windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2016.2.630.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint", PipeName="Pipe.ArtOfTest.WebAii.BrowserProvisioner_1042259122", ProcessId="3748").
[07/01 11:33:58,IEXPLORE.EXE(4140:9)] First trace message from pool thread "Internet Explorer injection thread" (managed ID = 9, native ID = 2492).
[07/01 11:33:58,IEXPLORE.EXE(4140:9),Framework] Connector.Start() : Process launched (ID=3776, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 197222").
[07/01 11:33:58,IEXPLORE.EXE(4140:9),Framework] Connector.Start() : Process exited (ID=3776, ExitCode=0, Path="C:\Windows\SysWOW64\rundll32.exe", Arguments=""C:\Program Files (x86)\Telerik\Test Studio\Bin\ArtOfTest.Connector.dll",AttachToWindowEntryPoint 197222").
[07/01 11:33:58,IEXPLORE.EXE(4140:9),Framework] Connector.InjectCode() : ------- Function Start -------
[07/01 11:33:58,IEXPLORE.EXE(4140:9),Framework] Connector.InjectCode() : Connection string: C:\Windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2016.2.630.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?3748
[07/01 11:33:58,IEXPLORE.EXE(4140:9),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 197222 on another thread...
[07/01 11:33:58,IEXPLORE.EXE(4140:7)] First trace message from pool thread "WaitAsync polling wait worker" (managed ID = 7, native ID = 3208).
[07/01 11:33:58,IEXPLORE.EXE(4140:7),Framework] <>c__DisplayClass25_3.<WaitForDocument>b__1() : Attempting to get document from window handle
[07/01 11:33:58,IEXPLORE.EXE(4140:9),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[07/01 11:33:58,IEXPLORE.EXE(4140:9),Framework] Connector.InjectCode() : Sending WM_COPYDATA to HWND 197222 (lpData = "C:\Windows\Microsoft.Net\assembly\GAC_MSIL\ArtOfTest.InternetExplorer\v4.0_2016.2.630.0__5339893a7cefe4d6\ArtOfTest.InternetExplorer.dll?ArtOfTest.InternetExplorer.ArtOfTestPluginEntryPoint?3748")...
[07/01 11:33:58,IEXPLORE.EXE(4140:14)] First trace message from background unnamed thread (managed ID = 14, native ID = 692).
[07/01 11:33:58,IEXPLORE.EXE(4140:14),Framework] ArtOfTestPluginEntryPoint.Connect() : The .NET Framework is now active inside the hooked process (HWND = 197222, PipeName = "Pipe.ArtOfTest.WebAii.BrowserProvisioner_1042259122", PID = 3748).
[07/01 11:33:58,IEXPLORE.EXE(4140:14),Framework] InternetExplorerActions.WaitForDocument() : Attempting to get IHTMLDocument2 from Internet Explorer HWND 197222 on same thread...
[07/01 11:33:58,IEXPLORE.EXE(4140:14),Framework] InternetExplorerActions.WaitForDocument() : IHTMLDocument2 successfully retrieved.
[07/01 11:33:58,IEXPLORE.EXE(4140:14),Framework] BrowserRemoteClient constructor : The client ID for this hooked process will be "Client_2c74a11a-20ae-40e1-b504-9218038ff0fa".
[07/01 11:33:58,ArtOfTest.Runner.exe(3712:10),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe.
[07/01 11:33:58,ArtOfTest.Runner.exe(3712:16)] First trace message from background thread "Remoted async command listener" (managed ID = 16, native ID = 3128).
[07/01 11:33:58,ArtOfTest.Runner.exe(3712:16),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Not connected; this will now wait indefinitely for a pipe client connection...
[07/01 11:33:58,IEXPLORE.EXE(4140:14),Framework] BrowserRemoteClient.CreateNamedPipes() : Named pipes created for communication with the server: {Command = "3b554049-9e4f-405f-913c-0d7dd12f5868.CommandPipe", Async = "3b554049-9e4f-405f-913c-0d7dd12f5868.AsyncPipe"}
[07/01 11:33:58,ArtOfTest.Runner.exe(3712:16),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A new client has connected to the pipe.
[07/01 11:33:58,ArtOfTest.Runner.exe(3712:16),Framework] BrowserRemoted.AsyncListenerThreadEntry() : Now waiting indefinitely for a command from the pipe client...
[07/01 11:33:58,IEXPLORE.EXE(4140:9),Framework] Connector.InjectCode() : Returned from sending WM_COPYDATA (LRESULT=0, GetLastError()=ERROR_ALREADY_EXISTS).
[07/01 11:33:58,IEXPLORE.EXE(4140:9),Framework] Connector.InjectCode() : ------- Function End -------
[07/01 11:33:58,ArtOfTest.Runner.exe(3712:10),Framework] Manager.AddNewBrowser() : Added client ID "Client_2c74a11a-20ae-40e1-b504-9218038ff0fa" (now there is/are 3 total).
[07/01 11:33:59,IEXPLORE.EXE(4140:12),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_b0284813-596c-4bfc-b613-5ef9c410d328") to pipe server...
[07/01 11:33:59,ArtOfTest.Runner.exe(3712:6),Framework] BrowserRemoted.AsyncListenerThreadEntry() : A ClientDisconnectRequest(ClientId="Client_b0284813-596c-4bfc-b613-5ef9c410d328") has been received from the pipe client.
[07/01 11:33:59,IEXPLORE.EXE(4140:12),Framework] BrowserRemoteClient.Disconnect() : Command pipe is already disconnected.
[07/01 11:33:59,ArtOfTest.Runner.exe(3712:6),Framework] Manager.RemoveBrowser() : Removed client ID "Client_b0284813-596c-4bfc-b613-5ef9c410d328" (2 remaining).
[07/01 11:33:59,ArtOfTest.Runner.exe(3712:6),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 6).
[07/01 11:33:59,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 14 "Connect to pop-up window : 'ereg/faces/jsp/capture/application.jsf'".
[07/01 11:33:59,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:33:59,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 15 "Verify 'ReviewSpan' is enabled"...
[07/01 11:34:00,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 15 "Verify 'ReviewSpan' is enabled".
[07/01 11:34:00,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:34:00,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 16 "Click 'ReviewSpan'"...
[07/01 11:34:00,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 16 "Click 'ReviewSpan'".
[07/01 11:34:00,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 3 "Execute test 'sub SJ CreateCUP'".
[07/01 11:34:00,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:34:00,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 4 "Comment: SET PERMIT USE TYPE"...
[07/01 11:34:00,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 4 "Comment: SET PERMIT USE TYPE".
[07/01 11:34:00,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:34:02,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 5 "Verify 'Projinfo2Link' is enabled"...
[07/01 11:34:03,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 5 "Verify 'Projinfo2Link' is enabled".
[07/01 11:34:03,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:34:03,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 6 "Click 'Projinfo2Link'"...
[07/01 11:34:03,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 6 "Click 'Projinfo2Link'".
[07/01 11:34:03,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:34:05,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 7 "Verify 'ProjUseSelect' is enabled"...
[07/01 11:34:05,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 7 "Verify 'ProjUseSelect' is enabled".
[07/01 11:34:05,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:34:05,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 8 "Select 'ByText' option 'Agricultural' on 'ProjUseSelect'"...
[07/01 11:34:05,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 8 "Select 'ByText' option 'Agricultural' on 'ProjUseSelect'".
[07/01 11:34:05,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:34:06,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 9 "Click 'ProjInfoSaveprojinfoSubmit'"...
[07/01 11:34:06,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 9 "Click 'ProjInfoSaveprojinfoSubmit'".
[07/01 11:34:06,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:34:06,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 10 "Comment: SET EXPIRATION"...
[07/01 11:34:06,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 10 "Comment: SET EXPIRATION".
[07/01 11:34:06,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:34:06,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 11 "Verify 'Statusrvw1Link' is enabled"...
[07/01 11:34:12,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 11 "Verify 'Statusrvw1Link' is enabled".
[07/01 11:34:12,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:34:12,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 12 "Click 'Statusrvw1Link'"...
[07/01 11:34:13,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 12 "Click 'Statusrvw1Link'".
[07/01 11:34:13,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:34:14,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 13 "Check 'AppDataJId3jRadio' to be 'True'"...
[07/01 11:34:14,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 13 "Check 'AppDataJId3jRadio' to be 'True'".
[07/01 11:34:14,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:34:14,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 14 "Enter text '20' in 'AppDataJId3kText'"...
[07/01 11:34:14,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 14 "Enter text '20' in 'AppDataJId3kText'".
[07/01 11:34:14,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:34:14,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 15 "Select 'ByText' option 'Year(s)' on 'DurCdSelect'"...
[07/01 11:34:14,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 15 "Select 'ByText' option 'Year(s)' on 'DurCdSelect'".
[07/01 11:34:14,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:34:14,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 16 "Select 'ByText' option 'Approval' on 'AppDataRecommendationMenuSelect'"...
[07/01 11:34:14,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 16 "Select 'ByText' option 'Approval' on 'AppDataRecommendationMenuSelect'".
[07/01 11:34:14,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:34:14,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 17 "Click 'SaveAppDataSubmit'"...
[07/01 11:34:15,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 17 "Click 'SaveAppDataSubmit'".
[07/01 11:34:15,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:34:15,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 18 "Comment: CHOOSE SOURCES"...
[07/01 11:34:15,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 18 "Comment: CHOOSE SOURCES".
[07/01 11:34:15,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:34:15,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 19 "Click 'SourcesSpan'"...
[07/01 11:34:16,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 19 "Click 'SourcesSpan'".
[07/01 11:34:16,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:34:17,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 20 "Select 'ByText' option 'Osceola' on 'JId21Select'"...
[07/01 11:34:17,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 20 "Select 'ByText' option 'Osceola' on 'JId21Select'".
[07/01 11:34:17,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:34:17,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 21 "Select 'ByText' option 'Groundwater' on 'SrcCtgryCdSelect'"...
[07/01 11:34:17,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 21 "Select 'ByText' option 'Groundwater' on 'SrcCtgryCdSelect'".
[07/01 11:34:17,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:34:17,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 22 "Click 'SearchSubmit'"...
[07/01 11:34:18,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 22 "Click 'SearchSubmit'".
[07/01 11:34:18,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:34:19,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 23 "Check 'SrcDetailsSrcTable3SrcChxBoxCheckBox' to be 'True'"...
[07/01 11:34:19,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 23 "Check 'SrcDetailsSrcTable3SrcChxBoxCheckBox' to be 'True'".
[07/01 11:34:19,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:34:19,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 24 "Click 'AddSelectedOffclsSubmit'"...
[07/01 11:34:19,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 24 "Click 'AddSelectedOffclsSubmit'".
[07/01 11:34:19,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:34:19,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 25 "Comment: ADD WELL"...
[07/01 11:34:19,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 25 "Comment: ADD WELL".
[07/01 11:34:19,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:34:19,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 26 "Click 'StationsSpan'"...
[07/01 11:34:20,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 26 "Click 'StationsSpan'".
[07/01 11:34:20,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:34:22,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 27 "Click 'AddStnSubmit'"...
[07/01 11:34:24,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 27 "Click 'AddStnSubmit'".
[07/01 11:34:24,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:34:24,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 28 "Wait for '6000' msec."...
[07/01 11:34:30,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 28 "Wait for '6000' msec.".
[07/01 11:34:30,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:34:30,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 29 "Enter text 'well01' in 'StnNmText'"...
[07/01 11:34:30,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 29 "Enter text 'well01' in 'StnNmText'".
[07/01 11:34:30,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:34:30,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 30 "Select 'ByText' option 'AUTOMATED TEST' on 'SiteIdSelect'"...
[07/01 11:34:31,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 30 "Select 'ByText' option 'AUTOMATED TEST' on 'SiteIdSelect'".
[07/01 11:34:31,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:34:31,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 31 "Select 'ByText' option 'Active' on 'StnSttsSelect'"...
[07/01 11:34:31,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyAfterStepExecuted() : Finished executing step 31 "Select 'ByText' option 'Active' on 'StnSttsSelect'".
[07/01 11:34:31,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.SetCurrentStep() : VisualDebugger::SetCurrentStep
[07/01 11:34:31,ArtOfTest.Runner.exe(3712:4),Execution] ExecutionEngine.NotifyBeforeStepExecuted() : Executing step 32 "Select 'ByText' option 'FAS - Upper Floridan Aquifer' on 'ProjSrcIdSelect'"...
[07/01 11:34:31,ArtOfTest.Runner.exe(3712:4),Error] ExecutionEngine.CatchExecuteStepException() : EXCEPTION! (see below)
     Outer Exception Type: ArtOfTest.WebAii.Exceptions.ExecuteCommandException
     Message: ExecuteCommand failed!
          InError set by the client. Client Error:
          System.ApplicationException: Unexpected error thrown while setting the dropdown ---> System.ArgumentOutOfRangeException: Specified argument was out of the range of valid values.
          Parameter name: DropDown was unable to find the item 'FAS - Upper Floridan Aquifer' in the dropdown requested. DropDown had the following values only:
          ,
          at ArtOfTest.InternetExplorer.IECommandProcessor.SelectDropDown(IHTMLElement target, Int32 index, String text, Boolean useValue)
          --- End of inner exception stack trace ---
          at ArtOfTest.InternetExplorer.IECommandProcessor.SelectDropDown(IHTMLElement target, Int32 index, String text, Boolean useValue)
          at ArtOfTest.InternetExplorer.IECommandProcessor.ProcessActionCommands(BrowserCommand request)
          at ArtOfTest.InternetExplorer.IECommandProcessor.ProcessCommandInternal(WebBrowserClass ieInstance, BrowserCommand request, IHTMLDocument2 document)
          BrowserCommand (Type:'Action',Info:'NotSet',Action:'SelectDropDown',Target:'ElementId (tagName: 'select',occurrenceIndex: '2')',Data:'T:FAS - Upper Floridan Aquifer',ClientId:'Client_2c74a11a-20ae-40e1-b504-9218038ff0fa',HasFrames:'False',FramesInfo:'',TargetFrameIndex:'-1',InError:'True',Response:'System.ApplicationException: Unexpected error thrown while setting the dropdown ---> System.ArgumentOutOfRangeException: Specified argument was out of the range of valid values.
          Parameter name: DropDown was unable to find the item 'FAS - Upper Floridan Aquifer' in the dropdown requested. DropDown had the following values only:
          ,
          at ArtOfTest.InternetExplorer.IECommandProcessor.SelectDropDown(IHTMLElement target, Int32 index, String text, Boolean useValue)
          --- End of inner exception stack trace ---
          at ArtOfTest.InternetExplorer.IECommandProcessor.SelectDropDown(IHTMLElement target, Int32 index, String text, Boolean useValue)
          at ArtOfTest.InternetExplorer.IECommandProcessor.ProcessActionCommands(BrowserCommand request)
          at ArtOfTest.InternetExplorer.IECommandProcessor.ProcessCommandInternal(WebBrowserClass ieInstance, BrowserCommand request, IHTMLDocument2 document)')
          InnerException: none.
     HRESULT: 0x80131500 (Official ID (if app.) = COR_E_EXCEPTION, Error Bit = FAILED, Facility = FACILITY_URT, Code = 5376)
     Call Stack:
          at ArtOfTest.WebAii.Core.Browser.ExecuteCommandInternal(BrowserCommand request)
          at ArtOfTest.WebAii.Core.Browser.ExecuteCommand(BrowserCommand request, Boolean performDomRefresh, Boolean waitUntilReady)
          at ArtOfTest.WebAii.Core.Browser.ExecuteCommand(BrowserCommand request)
          at ArtOfTest.WebAii.Core.Actions.DoSelectDropDown(Element targetElement, String data)
          at ArtOfTest.WebAii.Core.Actions.SelectDropDown(Element targetElement, String text, Boolean useValue)
          at ArtOfTest.WebAii.Core.Actions.SelectDropDown(Element targetElement, String itemText)
          at ArtOfTest.WebAii.Controls.HtmlControls.HtmlSelect.SelectByText(String text, Boolean invokeOnChangeEvent, Boolean initEvent)
          at ArtOfTest.WebAii.Design.IntrinsicTranslators.Descriptors.SelectDropDownActionDescriptor.Execute(Browser browser)
          at ArtOfTest.WebAii.Design.Extensibility.HtmlActionDescriptor.Execute(IAutomationHost autoHost)
          at ArtOfTest.WebAii.Design.Execution.ExecutionEngine.ExecuteStep(Int32 order)

[07/01 11:34:31,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::Start
[07/01 11:34:31,ArtOfTest.Runner.exe(3712:4),TestStudio] VisualDebugger.ShutDown() : VisualDebbuger::ShutDown::End
[07/01 11:34:31,ArtOfTest.Runner.exe(3712:4),Framework] BrowserProvisioner.EndService() : Connecting to the provisioning pipe "Pipe.ArtOfTest.WebAii.BrowserProvisioner_1042259122" in order to unblock and eventually terminate it...
[07/01 11:34:31,ArtOfTest.Runner.exe(3712:4),Framework] BrowserProvisioner.EndService() : Provisioning pipe connected.
[07/01 11:34:32,ArtOfTest.Runner.exe(3712:10),Framework] BrowserProvisioner.ServiceThread() : A new client has connected to the provisioning pipe but this will be treated as a termination cue.
[07/01 11:34:32,ArtOfTest.Runner.exe(3712:4),Framework] BrowserProvisioner.EndService() : Provisioning thread terminated.
[07/01 11:34:32,ArtOfTest.Runner.exe(3712:4),DialogPlayback] DialogMonitor.AddDialog() : Added {OnBeforeUnloadDialog(Dismiss=OK)} for monitoring.
[07/01 11:34:32,ArtOfTest.Runner.exe(3712:4),Framework] Manager.RemoveBrowser() : Removed client ID "Client_c36b383a-d582-46d1-b0be-8a7bdc4c17c7" (1 remaining).
[07/01 11:34:32,ArtOfTest.Runner.exe(3712:4),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 21).
[07/01 11:34:32,IEXPLORE.EXE(4140:1),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_c36b383a-d582-46d1-b0be-8a7bdc4c17c7") to pipe server...
[07/01 11:34:32,IEXPLORE.EXE(4140:1),Framework] BrowserRemoteClient.Disconnect() : Command pipe is already disconnected.
[07/01 11:34:32,ArtOfTest.Runner.exe(3712:21),Warning] PipeCommunication.SyncPipeRead() : Pipe read broken by thread-abort.
[07/01 11:34:35,ArtOfTest.Runner.exe(3712:4),DialogPlayback] DialogMonitor.AddDialog() : Added {OnBeforeUnloadDialog(Dismiss=OK)} for monitoring.
[07/01 11:34:35,ArtOfTest.Runner.exe(3712:4),Framework] Manager.RemoveBrowser() : Removed client ID "Client_2c74a11a-20ae-40e1-b504-9218038ff0fa" (0 remaining).
[07/01 11:34:35,ArtOfTest.Runner.exe(3712:4),Framework] Manager.SetActiveBrowser() : Active browser is now null.
[07/01 11:34:35,ArtOfTest.Runner.exe(3712:4),Framework] BrowserRemoted.End() : Now aborting the listener thread (TID = 16).
[07/01 11:34:35,IEXPLORE.EXE(4140:14),Framework] BrowserRemoteClient.Disconnect() : Sending ClientDisconnectRequest(ClientId="Client_2c74a11a-20ae-40e1-b504-9218038ff0fa") to pipe server...
[07/01 11:34:35,IEXPLORE.EXE(4140:14),Framework] BrowserRemoteClient.Disconnect() : Command pipe is already disconnected.
[07/01 11:34:35,ArtOfTest.Runner.exe(3712:16),Warning] PipeCommunication.SyncPipeRead() : Pipe read broken by thread-abort.
[07/01 11:34:38,ArtOfTest.Runner.exe(3712:4),DialogPlayback] DialogMonitor.Stop() : Dialog monitoring is being halted.
[07/01 11:34:38,ArtOfTest.Runner.exe(3712:4),UIAutomation] AutomationExtensions.AddAutomationEventHandler() : Added a(n) WindowPatternIdentifiers.WindowOpenedProperty handler on element System.Windows.Automation.AutomationElement scope Descendants.
[07/01 11:34:38,Telerik.TestStudio.RemoteExecutor.exe(3748:36),Execution] TestPlatform.AfterRunFinished() : Finished running test platform- attempting publish
[07/01 11:34:39,Telerik.TestStudio.RemoteExecutor.exe(3748:36),TestStudio] AutomationHostState.LoadFailureImageFromDisk() : Invalid file path to load the bitmap from: C:\Users\bpadmin\AppData\Local\Temp\2\ 131118608789957450_files\SJ Allocations Page_0_ImageOnFailure.png
[07/01 11:34:39,Telerik.TestStudio.RemoteExecutor.exe(3748:36),TestStudio] AutomationHostState.LoadFailureDOMFromDisk() : Invalid file path to load the DOM from: C:\Users\bpadmin\AppData\Local\Temp\2\ 131118608789957450_files\SJ Allocations Page_0_DomTreeOnFailure.txt
[07/01 11:34:39,Telerik.TestStudio.RemoteExecutor.exe(3748:20),TestStudio] AutomationHostState.LoadFailureImageFromDisk() : Invalid file path to load the bitmap from: C:\Users\bpadmin\AppData\Local\Temp\2\ 131118608789957450_files\SJ Allocations Page_0_ImageOnFailure.png
[07/01 11:34:39,Telerik.TestStudio.RemoteExecutor.exe(3748:20),TestStudio] AutomationHostState.LoadFailureDOMFromDisk() : Invalid file path to load the DOM from: C:\Users\bpadmin\AppData\Local\Temp\2\ 131118608789957450_files\SJ Allocations Page_0_DomTreeOnFailure.txt
[07/01 11:34:41,Telerik.TestStudio.ExecutionManagerService.exe(3992:40)] First trace message from pool unnamed thread (managed ID = 40, native ID = 3896).
[07/01 11:34:41,Telerik.TestStudio.ExecutionManagerService.exe(3992:40),Execution] JobRunner.<CleanupTestRunsStatus>b__19_0() : TestList finished; updating dispatch group header job Id = f121abc5-a1e3-47eb-a151-1e4ab939adcc
[07/01 11:34:41,Telerik.TestStudio.ExecutionManagerService.exe(3992:40),Execution] JobRunner.<CleanupTestRunsStatus>b__19_0() : TestList finished; sending notifications
[07/01 11:34:41,Telerik.TestStudio.ExecutionManagerService.exe(3992:40),Execution] PostListExecutionEmailer.OnAfterTestListCompleted() : Attempting to send email for job f121abc5-a1e3-47eb-a151-1e4ab939adcc
[07/01 11:35:41,Telerik.TestStudio.ExecutionManagerService.exe(3992:40),Execution] JobRunner.<CleanupTestRunsStatus>b__19_0() : TestList execution status expired- removing from list job id=f121abc5-a1e3-47eb-a151-1e4ab939adcc
